[INFO] fetching crate awsim-dynamodb 0.4.1...
[INFO] testing awsim-dynamodb-0.4.1 against master#cced03bfd61a304243a34504618ecec86c17063f for pr-154065-2
[INFO] extracting crate awsim-dynamodb 0.4.1 into /workspace/builds/worker-7-tc1/source
[INFO] started tweaking crates.io crate awsim-dynamodb 0.4.1
[INFO] finished tweaking crates.io crate awsim-dynamodb 0.4.1
[INFO] tweaked toml for crates.io crate awsim-dynamodb 0.4.1 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate awsim-dynamodb 0.4.1 on toolchain cced03bfd61a304243a34504618ecec86c17063f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate awsim-dynamodb 0.4.1 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded r2d2_sqlite v0.25.0
[INFO] [stderr]   Downloaded refinery v0.8.16
[INFO] [stderr]   Downloaded refinery-core v0.8.16
[INFO] [stderr]   Downloaded r2d2 v0.8.10
[INFO] [stderr]   Downloaded scheduled-thread-pool v0.2.7
[INFO] [stderr]   Downloaded refinery-macros v0.8.16
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] e4a21f4ee7bc487ff7a734b8ba7dbba05a850694ab7b0f63cd4f2b14caa2240d
[INFO] running `Command { std: "docker" "start" "-a" "e4a21f4ee7bc487ff7a734b8ba7dbba05a850694ab7b0f63cd4f2b14caa2240d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e4a21f4ee7bc487ff7a734b8ba7dbba05a850694ab7b0f63cd4f2b14caa2240d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e4a21f4ee7bc487ff7a734b8ba7dbba05a850694ab7b0f63cd4f2b14caa2240d", kill_on_drop: false }`
[INFO] [stdout] e4a21f4ee7bc487ff7a734b8ba7dbba05a850694ab7b0f63cd4f2b14caa2240d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 37471e32e442c0b0fcd932baa738e74556f44527dfb1959bf6575899a937ccf6
[INFO] running `Command { std: "docker" "start" "-a" "37471e32e442c0b0fcd932baa738e74556f44527dfb1959bf6575899a937ccf6", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.185
[INFO] [stderr]    Compiling zerocopy v0.8.48
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling zstd-safe v7.2.4
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling icu_normalizer v2.2.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling jobserver v0.1.34
[INFO] [stderr]    Compiling cc v1.2.60
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling tokio v1.52.1
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling rand v0.10.1
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling zstd-sys v2.0.16+zstd.1.5.7
[INFO] [stderr]    Compiling libsqlite3-sys v0.30.1
[INFO] [stderr]    Compiling brotli v8.0.2
[INFO] [stderr]    Compiling serde_spanned v0.6.9
[INFO] [stderr]    Compiling toml_datetime v0.6.11
[INFO] [stderr]    Compiling siphasher v1.0.2
[INFO] [stderr]    Compiling winnow v0.7.15
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling hashlink v0.9.1
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling toml_write v0.1.2
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling compression-core v0.4.32
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling uuid v1.23.1
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling axum-core v0.5.6
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling refinery-core v0.8.16
[INFO] [stderr]    Compiling h2 v0.4.13
[INFO] [stderr]    Compiling toml v0.8.23
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling rand v0.8.6
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling scheduled-thread-pool v0.2.7
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling serde_path_to_error v0.1.20
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling rust_decimal v1.41.0
[INFO] [stderr]    Compiling refinery-macros v0.8.16
[INFO] [stderr]    Compiling awsim-chaos v0.4.1
[INFO] [stderr]    Compiling awsim-iam-policy v0.4.1
[INFO] [stderr]    Compiling r2d2 v0.8.10
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling tokio-stream v0.1.18
[INFO] [stderr]    Compiling quick-xml v0.37.5
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling rusqlite v0.32.1
[INFO] [stderr]    Compiling zstd v0.13.3
[INFO] [stderr]    Compiling compression-codecs v0.4.38
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling async-compression v0.4.42
[INFO] [stderr]    Compiling r2d2_sqlite v0.25.0
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling refinery v0.8.16
[INFO] [stderr]    Compiling axum v0.8.9
[INFO] [stderr]    Compiling awsim-core v0.4.1
[INFO] [stderr]    Compiling awsim-dynamodb v0.4.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 31s
[INFO] running `Command { std: "docker" "inspect" "37471e32e442c0b0fcd932baa738e74556f44527dfb1959bf6575899a937ccf6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "37471e32e442c0b0fcd932baa738e74556f44527dfb1959bf6575899a937ccf6", kill_on_drop: false }`
[INFO] [stdout] 37471e32e442c0b0fcd932baa738e74556f44527dfb1959bf6575899a937ccf6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2a9df3c71186d216c3efab04126c287ecf6030e4ec623700eceab40ed142b335
[INFO] running `Command { std: "docker" "start" "-a" "2a9df3c71186d216c3efab04126c287ecf6030e4ec623700eceab40ed142b335", kill_on_drop: false }`
[INFO] [stderr]    Compiling awsim-dynamodb v0.4.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 9.44s
[INFO] running `Command { std: "docker" "inspect" "2a9df3c71186d216c3efab04126c287ecf6030e4ec623700eceab40ed142b335", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2a9df3c71186d216c3efab04126c287ecf6030e4ec623700eceab40ed142b335", kill_on_drop: false }`
[INFO] [stdout] 2a9df3c71186d216c3efab04126c287ecf6030e4ec623700eceab40ed142b335
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] f6a57db2c930246248d1548f81255da805d48c1a1134654fdc55b84a6bdc5ac5
[INFO] running `Command { std: "docker" "start" "-a" "f6a57db2c930246248d1548f81255da805d48c1a1134654fdc55b84a6bdc5ac5", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.28s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/awsim_dynamodb-05683d9c30b21f49)
[INFO] [stdout] 
[INFO] [stdout] running 88 tests
[INFO] [stdout] test expressions::eval::tests::get_nested_rejects_malformed_bracket_syntax ... ok
[INFO] [stdout] test expressions::eval::tests::get_nested_handles_chained_indices ... ok
[INFO] [stdout] test expressions::eval::tests::high_precision_numbers_compare_distinctly ... ok
[INFO] [stdout] test expressions::eval::tests::get_nested_indexes_into_list_of_maps_then_map_key ... ok
[INFO] [stdout] test expressions::eval::tests::size_of_string_returns_utf8_byte_length ... ok
[INFO] [stdout] test expressions::eval::tests::get_nested_indexes_into_list ... ok
[INFO] [stdout] test expressions::eval::tests::whole_number_decimals_compare_equal ... ok
[INFO] [stdout] test expressions::update::tests::add_increments_nested_numeric_attribute ... ok
[INFO] [stdout] test expressions::update::tests::add_integer_result_omits_decimal ... ok
[INFO] [stdout] test expressions::eval::tests::size_of_binary_returns_decoded_byte_count ... ok
[INFO] [stdout] test expressions::eval::tests::size_of_malformed_binary_falls_back_to_decode ... ok
[INFO] [stdout] test expressions::update::tests::add_unions_binary_set ... ok
[INFO] [stdout] test expressions::update::tests::add_creates_nested_attribute_when_missing ... ok
[INFO] [stdout] test expressions::update::tests::delete_removes_attribute_when_set_empties ... ok
[INFO] [stdout] test keys::tests::extracts_pk_sk_and_active_gsi ... ok
[INFO] [stdout] test expressions::update::tests::delete_subtracts_from_nested_string_set ... ok
[INFO] [stdout] test keys::tests::missing_gsi_attrs_yield_sparse_index ... ok
[INFO] [stdout] test expressions::update::tests::list_append_with_nested_if_not_exists_initializes_then_appends ... ok
[INFO] [stdout] test keys::tests::missing_hash_key_returns_none ... ok
[INFO] [stdout] test keys::tests::round_trips_storage_value ... ok
[INFO] [stdout] test operations::item::tests::get_item_reads_from_sqlite_after_put ... ok
[INFO] [stdout] test operations::item::tests::delete_item_writes_only_to_sqlite ... ok
[INFO] [stdout] test operations::item::tests::put_item_conditional_failure_returns_existing_item_when_requested ... ok
[INFO] [stdout] test operations::item::tests::get_item_returns_consumed_capacity_with_read_units ... ok
[INFO] [stdout] test operations::item::tests::put_item_rejects_missing_hash_key ... ok
[INFO] [stdout] test operations::backup::tests::restore_preserves_gsi_keys_so_restored_items_show_up_in_index_queries ... ok
[INFO] [stdout] test operations::item::tests::put_item_rejects_missing_sort_key ... ok
[INFO] [stdout] test operations::item::tests::put_item_returns_consumed_capacity_only_when_requested ... ok
[INFO] [stdout] test operations::item::tests::validate_sets_accepts_unique_non_empty_set ... ok
[INFO] [stdout] test operations::item::tests::validate_sets_handles_each_typed_set_kind ... ok
[INFO] [stdout] test operations::item::tests::validate_sets_recurses_into_lists_and_maps ... ok
[INFO] [stdout] test operations::item::tests::validate_sets_rejects_duplicate_string_set_elements ... ok
[INFO] [stdout] test operations::item::tests::validate_sets_rejects_empty_string_set ... ok
[INFO] [stdout] test operations::query::tests::cap_is_one_mib ... ok
[INFO] [stdout] test operations::query::tests::estimate_grows_with_string_payload ... ok
[INFO] [stdout] test operations::query::tests::estimate_handles_typical_attribute_value ... ok
[INFO] [stdout] test operations::item::tests::update_item_returns_pre_update_values_for_updated_old ... ok
[INFO] [stdout] test operations::item::tests::put_item_writes_only_to_sqlite ... ok
[INFO] [stdout] test operations::item::tests::update_item_returns_only_modified_attrs_for_updated_new ... ok
[INFO] [stdout] test operations::item::tests::update_item_writes_only_to_sqlite ... ok
[INFO] [stdout] test operations::query::tests::filter_attribute_type_string_matches_n_attribute ... ok
[INFO] [stdout] test operations::query::tests::gsi_all_projection_returns_full_item ... ok
[INFO] [stdout] test operations::query::tests::gsi_keys_only_projection_strips_non_key_attributes ... ok
[INFO] [stdout] test operations::query::tests::gsi_include_projection_returns_keys_plus_listed_attrs ... ok
[INFO] [stdout] test operations::query::tests::key_condition_accepts_begins_with_on_sort_key ... ok
[INFO] [stdout] test operations::query::tests::key_condition_rejects_contains_function ... ok
[INFO] [stdout] test operations::query::tests::key_condition_rejects_partition_key_inequality ... ok
[INFO] [stdout] test operations::query::tests::key_condition_rejects_in_on_sort_key ... ok
[INFO] [stdout] test operations::query::tests::parallel_scan_rejects_segment_at_or_above_total ... ok
[INFO] [stdout] test operations::query::tests::parallel_scan_rejects_segment_without_total ... ok
[INFO] [stdout] test operations::table::tests::create_describe_round_trip_with_default_replica ... ok
[INFO] [stdout] test operations::table::tests::create_global_table_requires_underlying_table ... ok
[INFO] [stdout] test operations::query::tests::query_against_unknown_index_raises_validation ... ok
[INFO] [stdout] test operations::query::tests::query_against_gsi_returns_only_matching_partition ... ok
[INFO] [stdout] test operations::table::tests::list_global_tables_filters_by_region ... ok
[INFO] [stdout] test operations::table::tests::update_global_table_creates_and_deletes_replicas ... ok
[INFO] [stdout] test operations::table::tests::create_table_accepts_gsi_with_declared_attribute ... ok
[INFO] [stdout] test operations::table::tests::create_table_rejects_gsi_keying_on_undeclared_attribute ... ok
[INFO] [stdout] test operations::table::tests::update_table_add_gsi_backfills_existing_items ... ok
[INFO] [stdout] test operations::table::tests::update_table_drop_gsi_clears_columns ... ok
[INFO] [stdout] test operations::query::tests::query_scanned_count_excludes_items_that_failed_key_condition ... ok
[INFO] [stdout] test operations::query::tests::parallel_scan_partitions_rows_disjointly_and_covers_all ... ok
[INFO] [stdout] test operations::table::tests::update_table_rejects_added_gsi_with_undeclared_attribute ... ok
[INFO] [stdout] test operations::transact::tests::write_items_commits_when_all_conditions_pass ... ok
[INFO] [stdout] test operations::table::tests::update_table_returns_active_status_on_added_gsi ... ok
[INFO] [stdout] test operations::transact::tests::write_items_rolls_back_on_failed_condition ... ok
[INFO] [stdout] test operations::transact::tests::cancellation_reasons_array_marks_failed_index ... ok
[INFO] [stdout] test sqlite_store::tests::delete_expired_items_no_op_when_attribute_missing ... ok
[INFO] [stdout] test sqlite_store::tests::delete_returns_whether_row_existed ... ok
[INFO] [stdout] test sqlite_store::tests::delete_expired_items_drops_only_past_ttl ... ok
[INFO] [stdout] test sqlite_store::tests::migrations_apply_to_fresh_db ... ok
[INFO] [stdout] test sqlite_store::tests::drop_table_clears_only_the_named_table ... ok
[INFO] [stdout] test sqlite_store::tests::isolation_across_account_region_table ... ok
[INFO] [stdout] test sqlite_store::tests::put_and_query_high_slot_gsi ... ok
[INFO] [stdout] test sqlite_store::tests::put_item_upserts_on_pk_sk_collision ... ok
[INFO] [stdout] test sqlite_store::tests::query_partition_orders_and_paginates ... ok
[INFO] [stdout] test throttle::tests::enforce_passes_within_burst_capacity ... ok
[INFO] [stdout] test throttle::tests::enforce_rebuilds_on_capacity_change ... ok
[INFO] [stdout] test throttle::tests::enforce_rejects_when_burst_exhausted ... ok
[INFO] [stdout] test throttle::tests::forget_resets_state ... ok
[INFO] [stdout] test throttle::tests::read_and_write_buckets_track_independently ... ok
[INFO] [stdout] test sqlite_store::tests::query_at_max_slot_index_works ... ok
[INFO] [stdout] test sqlite_store::tests::scan_table_streams_in_order_and_resumes ... ok
[INFO] [stdout] test sqlite_store::tests::truncate_table_clears_items_keeps_schema ... ok
[INFO] [stdout] test sqlite_store::tests::table_schema_round_trip ... ok
[INFO] [stdout] test operations::item::tests::put_item_throttles_when_wcu_exhausted_on_provisioned_table ... FAILED
[INFO] [stdout] test operations::item::tests::put_item_does_not_grow_in_memory_store ... ok
[INFO] [stdout] test operations::item::tests::pay_per_request_table_is_never_throttled ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- operations::item::tests::put_item_throttles_when_wcu_exhausted_on_provisioned_table stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'operations::item::tests::put_item_throttles_when_wcu_exhausted_on_provisioned_table' (78) panicked at src/operations/item.rs:1170:59:
[INFO] [stdout] called `Result::unwrap_err()` on an `Ok` value: Object {}
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x578b52246bf8 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x578b52246bf8 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x578b52246bf8 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x578b52246bf8 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x578b5225ddfa - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x578b5225ddfa - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x578b5224b4e2 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x578b5224b4e2 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x578b522258e6 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x578b522258e6 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x578b5223efc9 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x578b51fb7b6c - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x578b51fb7b6c - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x578b5223f182 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x578b5223f182 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x578b522259a2 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x578b5221ab79 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x578b522266bd - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x578b5225e63c - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x578b5225e3b2 - core[28b146924c2f19c6]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1872:5
[INFO] [stdout]   20:     0x578b51bf9596 - <core[28b146924c2f19c6]::result::Result<serde_json[e603531d079d0c8c]::value::Value, awsim_core[43f47eacb8f4b8ed]::error::AwsError>>::unwrap_err
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1329:22
[INFO] [stdout]   21:     0x578b51c9296b - awsim_dynamodb[4776ab0a8a339416]::operations::item::tests::put_item_throttles_when_wcu_exhausted_on_provisioned_table
[INFO] [stdout]                                at /opt/rustwide/workdir/src/operations/item.rs:1170:59
[INFO] [stdout]   22:     0x578b51c78ca7 - awsim_dynamodb[4776ab0a8a339416]::operations::item::tests::put_item_throttles_when_wcu_exhausted_on_provisioned_table::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/operations/item.rs:1145:68
[INFO] [stdout]   23:     0x578b51cacc46 - <awsim_dynamodb[4776ab0a8a339416]::operations::item::tests::put_item_throttles_when_wcu_exhausted_on_provisioned_table::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x578b51faac4b - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x578b51faac4b - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x578b51fb84c5 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x578b51fb84c5 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x578b51fb84c5 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x578b51fb84c5 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x578b51fb84c5 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x578b51fb84c5 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x578b51fb84c5 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x578b51fb3024 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x578b51fb3024 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x578b51fbb542 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x578b51fbb542 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x578b51fbb542 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x578b51fbb542 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x578b51fbb542 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x578b51fbb542 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x578b51fbb542 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x578b5224652f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   43:     0x578b5224652f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x754fe384daa4 - <unknown>
[INFO] [stdout]   45:     0x754fe38daa64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     operations::item::tests::put_item_throttles_when_wcu_exhausted_on_provisioned_table
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 87 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.59s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "f6a57db2c930246248d1548f81255da805d48c1a1134654fdc55b84a6bdc5ac5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f6a57db2c930246248d1548f81255da805d48c1a1134654fdc55b84a6bdc5ac5", kill_on_drop: false }`
[INFO] [stdout] f6a57db2c930246248d1548f81255da805d48c1a1134654fdc55b84a6bdc5ac5
